View Issue Details

IDProjectCategoryView StatusLast Update
0000539T99X171.00 SKB EagleSWpublic2019-03-12 10:23
Reporter(ALTech) SY Yoon Assigned To(ALTech) SY Yoon Due Date2019-03-08 20:20
PriorityhighSeveritys2-severeReproducibilityhave not tried
Status closedResolutionfixed 
Summary0000539: Support ramoops
DescriptionSKB asked to support ramoops in Eagle.
It is ramoops documents as follows :
             https://www.kernel.org/doc/html/v4.15/admin-guide/ramoops.html

It is a function as follows :
             If the STB is rebooted abnormally, it is necessary to obtain a log(kmsg).

Please let me know you support it now or not.
If you don't support it, please let us your plan to support it.
TagsNo tags attached.
Attach Tags

Users monitoring this issue

User List (ALTech) Eric Kim , (ALTech) Jewoo Lee

Activities

(SW) Jacky Chiang

2019-03-06 19:35

manager   ~0001372

Last edited: 2019-03-06 19:36

Hi Kerwin,

   This is for demo purpose so that we will do it from next week.
However, please pass this request to Amlogic to handle it.

(SW) Kerwin Chen

2019-03-07 12:18

developer   ~0001397

"ramoops" is already supported on Eagle board.
Please use commands to test:

1. connect to console port and change to root
  ===> console:/ $ su

2. send signal to trigger kernel crash
  ===> console:/ # echo c > /proc/sysrq-trigger

3. After system rebooting, check saved logs in sysfs. There will be log files in /sys/fs/pstore folder
  ===> console:/ # cat /sys/fs/pstore/console-ramoops-0

(SW) Kerwin Chen

2019-03-07 12:22

developer   ~0001398

Feature is already included in current release (r5b)
https://drive.google.com/open?id=18xWa86cp2D6A_O5wLESN4PjPL_uIE5sR

(ALTech) Eric Kim

2019-03-07 16:44

developer   ~0001401

Hi Kerwin,

Just extracted ramoops file, which is 1.2M.
Could you please check if the attached ramoops file is expected/correct?
console-ramoops-0 (32,756 bytes)   
="debugfs" ino=44818 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  507.595287@2] type=1400 audit(1551947003.388:304): avc: denied { search } for pid=4982 comm="Binder:4982_3" name="leds" dev="sysfs" ino=8704 scontext=u:r:system_app:s0 tcontext=u:object_r:sysfs_leds:s0 tclass=dir permissive=1
[  507.613928@2] type=1400 audit(1551947003.388:304): avc: denied { search } for pid=4982 comm="Binder:4982_3" name="leds" dev="sysfs" ino=8704 scontext=u:r:system_app:s0 tcontext=u:object_r:sysfs_leds:s0 tclass=dir permissive=1
[  507.629777@2] type=1400 audit(1551947003.388:305): avc: denied { read write } for pid=4982 comm="Binder:4982_3" name="trigger" dev="sysfs" ino=15709 scontext=u:r:system_app:s0 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[  507.651798@2] type=1400 audit(1551947003.388:305): avc: denied { read write } for pid=4982 comm="Binder:4982_3" name="trigger" dev="sysfs" ino=15709 scontext=u:r:system_app:s0 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[  507.670726@2] type=1400 audit(1551947003.388:306): avc: denied { open } for pid=4982 comm="Binder:4982_3" path="/sys/devices/platform/gpioleds/leds/remote_led/trigger" dev="sysfs" ino=15709 scontext=u:r:system_app:s0 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[  508.882351@0] spdif_a is set to disable
[  508.882396@0] asoc-aml-card auge_sound: tdm playback stop
[  513.347581@1] type=1400 audit(1551947003.388:306): avc: denied { open } for pid=4982 comm="Binder:4982_3" path="/sys/devices/platform/gpioleds/leds/remote_led/trigger" dev="sysfs" ino=15709 scontext=u:r:system_app:s0 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[  513.367832@1] type=1400 audit(1551947009.164:307): avc: denied { dac_override } for pid=3019 comm="iSQMSAgent" capability=1 scontext=u:r:isqms_agent:s0 tcontext=u:r:isqms_agent:s0 tclass=capability permissive=1
[  513.810147@0] type=1400 audit(1551947009.164:307): avc: denied { dac_override } for pid=3019 comm="iSQMSAgent" capability=1 scontext=u:r:isqms_agent:s0 tcontext=u:r:isqms_agent:s0 tclass=capability permissive=1
[  513.824558@0] type=1400 audit(1551947009.632:308): avc: denied { read } for pid=6186 comm="Binder:6186_2" name="type" dev="sysfs" ino=17801 scontext=u:r:priv_app:s0:c512,c768 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[  513.854284@0] type=1400 audit(1551947009.632:308): avc: denied { read } for pid=6186 comm="Binder:6186_2" name="type" dev="sysfs" ino=17801 scontext=u:r:priv_app:s0:c512,c768 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[  513.873437@0] type=1400 audit(1551947009.632:309): avc: denied { open } for pid=6186 comm="Binder:6186_2" path="/sys/devices/virtual/net/tunl0/type" dev="sysfs" ino=17801 scontext=u:r:priv_app:s0:c512,c768 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[  513.896964@0] type=1400 audit(1551947009.632:309): avc: denied { open } for pid=6186 comm="Binder:6186_2" path="/sys/devices/virtual/net/tunl0/type" dev="sysfs" ino=17801 scontext=u:r:priv_app:s0:c512,c768 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[  513.928827@1] type=1400 audit(1551947009.676:310): avc: denied { open } for pid=6186 comm="Binder:6186_2" path="/proc/bus/pci/devices" dev="proc" ino=4026532023 scontext=u:r:priv_app:s0:c512,c768 tcontext=u:object_r:proc:s0 tclass=file permissive=1
[  513.955683@1] type=1400 audit(1551947009.676:310): avc: denied { open } for pid=6186 comm="Binder:6186_2" path="/proc/bus/pci/devices" dev="proc" ino=4026532023 scontext=u:r:priv_app:s0:c512,c768 tcontext=u:object_r:proc:s0 tclass=file permissive=1
[  513.976273@1] type=1400 audit(1551947009.760:311): avc: denied { read } for pid=6186 comm="Binder:6186_2" name="stat" dev="proc" ino=18279 scontext=u:r:priv_app:s0:c512,c768 tcontext=u:r:zygote:s0 tclass=file permissive=1
[  527.370135@0] type=1400 audit(1551947013.068:315): avc: denied { getattr } for pid=3010 comm="memtrack@1.0-se" path="/sys/kernel/debug/mali0/ctx/7808_10/mem_profile" dev="debugfs" ino=89224 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  527.393461@0] type=1400 audit(1551947023.192:316): avc: denied { read } for pid=3010 comm="memtrack@1.0-se" name="mem_profile" dev="debugfs" ino=89224 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  527.415557@2] type=1400 audit(1551947023.192:316): avc: denied { read } for pid=3010 comm="memtrack@1.0-se" name="mem_profile" dev="debugfs" ino=89224 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  527.432581@2] type=1400 audit(1551947023.192:317): avc: denied { open } for pid=3010 comm="memtrack@1.0-se" path="/sys/kernel/debug/mali0/ctx/7808_10/mem_profile" dev="debugfs" ino=89224 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  527.457796@2] type=1400 audit(1551947023.192:317): avc: denied { open } for pid=3010 comm="memtrack@1.0-se" path="/sys/kernel/debug/mali0/ctx/7808_10/mem_profile" dev="debugfs" ino=89224 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  527.481779@2] type=1400 audit(1551947023.192:318): avc: denied { getattr } for pid=3010 comm="memtrack@1.0-se" path="/sys/kernel/debug/mali0/ctx/7808_10/mem_profile" dev="debugfs" ino=89224 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  561.339791@0] meson-remote ff808040.rc: invalid custom:0xe1c00f80
[  561.340247@0] meson-remote ff808040.rc: cur_custom is nulll
[  561.345834@0] meson-remote ff808040.rc: no valid key to handle
[  645.998525@0] type=1400 audit(1551947023.192:318): avc: denied { getattr } for pid=3010 comm="memtrack@1.0-se" path="/sys/kernel/debug/mali0/ctx/7808_10/mem_profile" dev="debugfs" ino=89224 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  646.019690@0] type=1400 audit(1551947141.820:319): avc: denied { read } for pid=7117 comm="Binder:7117_2" name="address" dev="sysfs" ino=15196 scontext=u:r:system_app:s0 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[  646.055873@0] type=1400 audit(1551947141.820:319): avc: denied { read } for pid=7117 comm="Binder:7117_2" name="address" dev="sysfs" ino=15196 scontext=u:r:system_app:s0 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[  646.070319@0] type=1400 audit(1551947141.820:320): avc: denied { open } for pid=7117 comm="Binder:7117_2" path="/sys/devices/platform/ff3f0000.ethernet/net/eth0/address" dev="sysfs" ino=15196 scontext=u:r:system_app:s0 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[  649.625929@0] vmap allocation for size 8298496 failed: use vmalloc=<size> to increase size
[  649.628667@0] ion_dev soc:ion_dev: Fail to allocate buffer
[  649.835243@2] vmap allocation for size 8491008 failed: use vmalloc=<size> to increase size
[  649.838013@2] ion_dev soc:ion_dev: Fail to allocate buffer
[  649.839913@0] vmap allocation for size 8491008 failed: use vmalloc=<size> to increase size
[  649.839985@0] ion_dev soc:ion_dev: Fail to allocate buffer
[  649.872309@1] vmap allocation for size 8491008 failed: use vmalloc=<size> to increase size
[  649.875156@1] ion_dev soc:ion_dev: Fail to allocate buffer
[  655.199226@0] type=1400 audit(1551947141.820:320): avc: denied { open } for pid=7117 comm="Binder:7117_2" path="/sys/devices/platform/ff3f0000.ethernet/net/eth0/address" dev="sysfs" ino=15196 scontext=u:r:system_app:s0 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[  655.217954@0] type=1400 audit(1551947151.020:321): avc: denied { read } for pid=3010 comm="memtrack@1.0-se" name="mem_profile" dev="debugfs" ino=45821 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  655.239489@0] type=1400 audit(1551947151.020:321): avc: denied { read } for pid=3010 comm="memtrack@1.0-se" name="mem_profile" dev="debugfs" ino=45821 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  655.261017@0] type=1400 audit(1551947151.020:322): avc: denied { open } for pid=3010 comm="memtrack@1.0-se" path="/sys/kernel/debug/mali0/ctx/4443_3/mem_profile" dev="debugfs" ino=45821 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  655.285697@0] type=1400 audit(1551947151.020:322): avc: denied { open } for pid=3010 comm="memtrack@1.0-se" path="/sys/kernel/debug/mali0/ctx/4443_3/mem_profile" dev="debugfs" ino=45821 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  655.310040@0] type=1400 audit(1551947151.020:323): avc: denied { getattr } for pid=3010 comm="memtrack@1.0-se" path="/sys/kernel/debug/mali0/ctx/4443_3/mem_profile" dev="debugfs" ino=45821 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  658.842452@3] vmap allocation for size 8491008 failed: use vmalloc=<size> to increase size
[  658.845235@3] ion_dev soc:ion_dev: Fail to allocate buffer
[  658.863755@3] vmap allocation for size 8491008 failed: use vmalloc=<size> to increase size
[  658.866630@3] ion_dev soc:ion_dev: Fail to allocate buffer
[  658.898717@0] vmap allocation for size 8491008 failed: use vmalloc=<size> to increase size
[  658.902942@0] ion_dev soc:ion_dev: Fail to allocate buffer
[  666.988134@3] hdmitx: system: sname = 2160p60hz
[  666.988170@3] hdmitx: system: char_clk = 594000
[  666.991606@3] hdmitx: system: cd = 5
[  666.995153@3] hdmitx: system: cs = 3
[  666.998880@3] hdmitx: RX tmds clk: 600   Calc clk: 371
[  669.085387@1] selinux: avc:  denied  { set } for property=media.omx.display_mode pid=3116 uid=0 gid=0 scontext=u:r:tvs_mediaserver:s0 tcontext=u:object_r:media_prop:s0 tclass=property_service permissive=1
[  669.085387@1] 
[  669.400286@2] spdif_a fifo ctrl, frddr:0 type:1, 16 bits
[  669.400325@2] spdif_info: rate: 48000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x200, ch1_r:0x200
[  669.401475@0] is_reset=0
[  669.401511@0] vdec_init, dev_name:ammvdec_h264, vdec_type=VDEC_TYPE_FRAME_BLOCK
[  669.413103@1] H264 sysinfo: 1920x1080 duration=3200, pts_outside=1
[  669.413205@1] [TEE], the fw (h264_multi) will be loaded.
[  669.433059@2] audio_ddr_mngr: aml_frddr_select_dst_ss sel:1, dst_src:3
[  669.452182@2] spdif_a is set to enable
[  669.452227@2] asoc-aml-card auge_sound: tdm playback enable
[  669.480827@2] vmap allocation for size 8298496 failed: use vmalloc=<size> to increase size
[  669.484557@2] ion_dev soc:ion_dev: Fail to allocate buffer
[  669.533817@3] vmap allocation for size 8298496 failed: use vmalloc=<size> to increase size
[  669.537769@3] ion_dev soc:ion_dev: Fail to allocate buffer
[  669.632595@2] omx_need_drop_frame_num 0
[  669.665016@0] di_cma_alloc:alloc 8 buffer use 60 ms(369564~369624)
[  669.666903@1] pre_de_buf_config:369624ms 1th source change: 0x0/0/0/0=>0x9000/1280/720/0
[  669.693398@0] video first pts = dd2d4
[  669.710347@0] 
[  669.710347@0] [amvideo..] saturation_pre:0 hue_pre:0 mab:1000000
[  669.743557@0] 
[  669.743557@0] [amvideo..] saturation_pre:0 hue_pre:0 mab:1000000
[  670.358920@2] vmap allocation for size 2551808 failed: use vmalloc=<size> to increase size
[  670.362475@2] ion_dev soc:ion_dev: Fail to allocate buffer
[  680.285074@1] skbrm_probe
[  680.286127@1] skbrm_input_configured
[  680.286451@1] input: BMM_BA01_094 as /devices/virtual/misc/uhid/0005:06E7:8103.0001/input/input5
[  680.303956@1] skbrm 0005:06E7:8103.0001: input,hidraw0: BLUETOOTH HID v1.11 Keyboard [BMM_BA01_094] on 
[  680.307923@1] hid_hw_start
[  681.811925@0] type=1400 audit(1551947151.020:323): avc: denied { getattr } for pid=3010 comm="memtrack@1.0-se" path="/sys/kernel/debug/mali0/ctx/4443_3/mem_profile" dev="debugfs" ino=45821 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  681.852141@0] type=1400 audit(1551947177.632:324): avc: denied { read } for pid=3010 comm="memtrack@1.0-se" name="mem_profile" dev="debugfs" ino=92141 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  681.879191@0] type=1400 audit(1551947177.632:324): avc: denied { read } for pid=3010 comm="memtrack@1.0-se" name="mem_profile" dev="debugfs" ino=92141 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  681.911822@0] type=1400 audit(1551947177.632:325): avc: denied { open } for pid=3010 comm="memtrack@1.0-se" path="/sys/kernel/debug/mali0/ctx/5338_11/mem_profile" dev="debugfs" ino=92141 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  681.945870@0] type=1400 audit(1551947177.632:325): avc: denied { open } for pid=3010 comm="memtrack@1.0-se" path="/sys/kernel/debug/mali0/ctx/5338_11/mem_profile" dev="debugfs" ino=92141 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  681.969501@0] type=1400 audit(1551947177.632:326): avc: denied { getattr } for pid=3010 comm="memtrack@1.0-se" path="/sys/kernel/debug/mali0/ctx/5338_11/mem_profile" dev="debugfs" ino=92141 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  682.405524@3] --> skbrm_raw_event:  80 00 4a 00
[  682.405551@3] key down 108
[  682.407879@2] --> skbrm_raw_event:  80 00 ff ff
[  682.411689@0] key up 108
[  682.411689@0] 
[  686.176182@3] is_reset=1
[  686.176339@3] di_receiver_event_fun: vf_notify_receiver unreg
[  686.179154@0] disbled video for next before free keep buffer!
[  686.189198@0] keep exit is skip current
[  686.189225@0] video first pts = 0
[  686.191697@0] VD1 AFBC 0x0.
[  686.195453@0] pattern detected = 2, pts_enter_pattern_cnt =2, pts_exit_pattern_cnt =1[  686.202301@0] di_unreg_process_irq disable di mirror image.
[  686.208258@0] di_cma_release:release 10 buffer use 0 ms(386164~386164)
[  686.210677@3] is_reset=1
[  686.210708@3] vdec_init, dev_name:ammvdec_h264, vdec_type=VDEC_TYPE_FRAME_BLOCK
[  686.220475@2] H264 sysinfo: 1920x1080 duration=3200, pts_outside=1
[  686.220595@2] [TEE], the fw (h264_multi) will be loaded.
[  686.241288@2] is_reset=0
[  686.246504@2] di_receiver_event_fun: vf_notify_receiver unreg
[  686.246641@0] disbled video for next before free keep buffer!
[  686.252483@0] VD1 AFBC 0x0.
[  686.255147@0] di_unreg_process_irq disable di mirror image.
[  686.267044@0] di_cma_release:release 0 buffer use 0 ms(386224~386224)
[  686.433228@2] vmap allocation for size 8298496 failed: use vmalloc=<size> to increase size
[  686.436257@2] ion_dev soc:ion_dev: Fail to allocate buffer
[  686.463800@1] vmap allocation for size 8298496 failed: use vmalloc=<size> to increase size
[  686.467363@1] ion_dev soc:ion_dev: Fail to allocate buffer
[  686.540991@3] is_reset=0
[  686.541027@3] vdec_init, dev_name:ammvdec_h264, vdec_type=VDEC_TYPE_FRAME_BLOCK
[  686.547413@3] H264 sysinfo: 1920x1080 duration=3200, pts_outside=1
[  686.551483@3] [TEE], the fw (h264_multi) will be loaded.
[  686.623804@1] di_cma_alloc:alloc 10 buffer use 4 ms(386576~386580)
[  686.624799@1] pre_de_buf_config:386584ms 1th source change: 0x0/0/0/0=>0x9000/1280/720/0
[  686.631732@0] omx_need_drop_frame_num 0
[  686.643663@0] video first pts = dd2d4
[  694.562884@0] vmap allocation for size 3641344 failed: use vmalloc=<size> to increase size
[  694.573983@2] ion_dev soc:ion_dev: Fail to allocate buffer
[  695.289581@0] type=1400 audit(1551947177.632:326): avc: denied { getattr } for pid=3010 comm="memtrack@1.0-se" path="/sys/kernel/debug/mali0/ctx/5338_11/mem_profile" dev="debugfs" ino=92141 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  695.312689@0] type=1400 audit(1551947191.112:327): avc: denied { read } for pid=7117 comm="Binder:7117_3" name="address" dev="sysfs" ino=15196 scontext=u:r:system_app:s0 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[  695.330915@0] type=1400 audit(1551947191.112:327): avc: denied { read } for pid=7117 comm="Binder:7117_3" name="address" dev="sysfs" ino=15196 scontext=u:r:system_app:s0 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[  695.355367@0] type=1400 audit(1551947191.112:328): avc: denied { open } for pid=7117 comm="Binder:7117_3" path="/sys/devices/platform/ff3f0000.ethernet/net/eth0/address" dev="sysfs" ino=15196 scontext=u:r:system_app:s0 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[  697.466175@0] spdif_a is set to disable
[  697.466226@0] asoc-aml-card auge_sound: tdm playback stop
[  698.719593@3] spdif_a fifo ctrl, frddr:0 type:1, 16 bits
[  698.719627@3] spdif_info: rate: 48000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x200, ch1_r:0x200
[  698.736775@3] audio_ddr_mngr: aml_frddr_select_dst_ss sel:1, dst_src:3
[  698.745219@3] spdif_a is set to enable
[  698.745260@3] asoc-aml-card auge_sound: tdm playback enable
[  702.054854@0] type=1400 audit(1551947191.112:328): avc: denied { open } for pid=7117 comm="Binder:7117_3" path="/sys/devices/platform/ff3f0000.ethernet/net/eth0/address" dev="sysfs" ino=15196 scontext=u:r:system_app:s0 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[  702.151735@3] type=1400 audit(1551947197.876:329): avc: denied { read } for pid=3010 comm="memtrack@1.0-se" name="mem_profile" dev="debugfs" ino=92141 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  702.168348@3] type=1400 audit(1551947197.876:329): avc: denied { read } for pid=3010 comm="memtrack@1.0-se" name="mem_profile" dev="debugfs" ino=92141 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  702.189411@3] type=1400 audit(1551947197.876:330): avc: denied { open } for pid=3010 comm="memtrack@1.0-se" path="/sys/kernel/debug/mali0/ctx/5338_11/mem_profile" dev="debugfs" ino=92141 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  702.214009@3] type=1400 audit(1551947197.876:330): avc: denied { open } for pid=3010 comm="memtrack@1.0-se" path="/sys/kernel/debug/mali0/ctx/5338_11/mem_profile" dev="debugfs" ino=92141 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  702.238494@3] type=1400 audit(1551947197.876:331): avc: denied { getattr } for pid=3010 comm="memtrack@1.0-se" path="/sys/kernel/debug/mali0/ctx/5338_11/mem_profile" dev="debugfs" ino=92141 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  704.394142@0] is_reset=1
[  704.394884@0] di_receiver_event_fun: vf_notify_receiver unreg
[  704.410749@1] disbled video for next before free keep buffer!
[  704.410852@1] keep exit is skip current
[  704.415501@1] video first pts = 0
[  704.422931@1] VD1 AFBC 0x0.
[  704.422959@1] pattern detected = 1, pts_enter_pattern_cnt =3, pts_exit_pattern_cnt =3[  704.428818@1] di_unreg_process_irq disable di mirror image.
[  704.435673@1] di_cma_release:release 10 buffer use 0 ms(404392~404392)
[  704.442392@0] is_reset=1
[  704.442934@0] vdec_init, dev_name:ammvdec_h264, vdec_type=VDEC_TYPE_FRAME_BLOCK
[  704.459453@0] H264 sysinfo: 1920x1080 duration=3200, pts_outside=1
[  704.460092@0] [TEE], the fw (h264_multi) will be loaded.
[  704.477990@0] is_reset=0
[  704.494771@3] di_receiver_event_fun: vf_notify_receiver unreg
[  704.494901@1] disbled video for next before free keep buffer!
[  704.500922@1] VD1 AFBC 0x0.
[  704.503523@1] di_unreg_process_irq disable di mirror image.
[  704.509280@1] di_cma_release:release 0 buffer use 0 ms(404468~404468)
[  704.674945@0] vmap allocation for size 8298496 failed: use vmalloc=<size> to increase size
[  704.678004@0] ion_dev soc:ion_dev: Fail to allocate buffer
[  704.705186@3] vmap allocation for size 8298496 failed: use vmalloc=<size> to increase size
[  704.708068@3] ion_dev soc:ion_dev: Fail to allocate buffer
[  704.791426@3] is_reset=0
[  704.791462@3] vdec_init, dev_name:ammvdec_h264, vdec_type=VDEC_TYPE_FRAME_BLOCK
[  704.798819@3] H264 sysinfo: 1920x1080 duration=3200, pts_outside=1
[  704.802590@1] [TEE], the fw (h264_multi) will be loaded.
[  704.878354@2] di_cma_alloc:alloc 10 buffer use 4 ms(404832~404836)
[  704.882977@1] pre_de_buf_config:404840ms 1th source change: 0x0/0/0/0=>0x9000/1280/720/0
[  704.888576@1] omx_need_drop_frame_num 0
[  704.895226@0] video first pts = dd2d4
[  721.441805@0] is_reset=1
[  721.442046@0] di_receiver_event_fun: vf_notify_receiver unreg
[  721.444716@3] disbled video for next before free keep buffer!
[  721.452241@3] video first pts = 0
[  721.454433@3] VD1 AFBC 0x0.
[  721.460323@3] pattern detected = 2, pts_enter_pattern_cnt =2, pts_exit_pattern_cnt =1[  721.466219@3] di_unreg_process_irq disable di mirror image.
[  721.470346@3] di_cma_release:release 10 buffer use 0 ms(421428~421428)
[  721.476783@0] is_reset=1
[  721.476967@0] vdec_init, dev_name:ammvdec_h264, vdec_type=VDEC_TYPE_FRAME_BLOCK
[  721.494832@0] H264 sysinfo: 1920x1080 duration=3200, pts_outside=1
[  721.495901@0] [TEE], the fw (h264_multi) will be loaded.
[  721.511684@0] is_reset=0
[  721.518546@0] di_receiver_event_fun: vf_notify_receiver unreg
[  721.518675@3] disbled video for next before free keep buffer!
[  721.525037@3] VD1 AFBC 0x0.
[  721.527303@3] di_unreg_process_irq disable di mirror image.
[  721.533028@3] di_cma_release:release 0 buffer use 0 ms(421492~421492)
[  721.708768@0] vmap allocation for size 8298496 failed: use vmalloc=<size> to increase size
[  721.712761@0] ion_dev soc:ion_dev: Fail to allocate buffer
[  721.738722@0] vmap allocation for size 8298496 failed: use vmalloc=<size> to increase size
[  721.754616@1] ion_dev soc:ion_dev: Fail to allocate buffer
[  721.846568@3] is_reset=0
[  721.846608@3] vdec_init, dev_name:ammvdec_h264, vdec_type=VDEC_TYPE_FRAME_BLOCK
[  721.854214@3] H264 sysinfo: 1920x1080 duration=3200, pts_outside=1
[  721.857493@3] [TEE], the fw (h264_multi) will be loaded.
[  721.926265@1] omx_need_drop_frame_num 0
[  721.927200@2] di_cma_alloc:alloc 10 buffer use 4 ms(421880~421884)
[  721.936483@1] pre_de_buf_config:421896ms 1th source change: 0x0/0/0/0=>0x9000/1280/720/0
[  721.945591@0] video first pts = dd2d4
[  722.968781@0] type=1400 audit(1551947197.876:331): avc: denied { getattr } for pid=3010 comm="memtrack@1.0-se" path="/sys/kernel/debug/mali0/ctx/5338_11/mem_profile" dev="debugfs" ino=92141 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  722.989198@2] type=1400 audit(1551947218.792:332): avc: denied { read } for pid=3010 comm="memtrack@1.0-se" name="mem_profile" dev="debugfs" ino=50139 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  723.009924@2] type=1400 audit(1551947218.792:332): avc: denied { read } for pid=3010 comm="memtrack@1.0-se" name="mem_profile" dev="debugfs" ino=50139 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  723.031421@2] type=1400 audit(1551947218.792:333): avc: denied { open } for pid=3010 comm="memtrack@1.0-se" path="/sys/kernel/debug/mali0/ctx/3302_5/mem_profile" dev="debugfs" ino=50139 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  723.056176@2] type=1400 audit(1551947218.792:333): avc: denied { open } for pid=3010 comm="memtrack@1.0-se" path="/sys/kernel/debug/mali0/ctx/3302_5/mem_profile" dev="debugfs" ino=50139 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  723.080242@2] type=1400 audit(1551947218.792:334): avc: denied { getattr } for pid=3010 comm="memtrack@1.0-se" path="/sys/kernel/debug/mali0/ctx/3302_5/mem_profile" dev="debugfs" ino=50139 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
[  823.204885@0] sysrq: SysRq : Trigger a crash
[  840.220430@3] 
[  840.220430@3] 
[  840.220430@3] 
[  840.220430@3] HARDLOCKUP____ERR.CPU[0] <irqen:0 isren1>START
[  840.225396@3] 
[  840.225396@3] dump_cpu[3] irq:  0 preempt:10000  sh
[  840.231897@3] IRQ arch_timer_handler_virt, 10000
[  840.236484@3] Task dump for CPU 3:
[  840.239864@3] sh              R  running task        0  8944   3095 0x00000000
[  840.247063@3] [<c0313cf8>] (unwind_backtrace) from [<c030e760>] (show_stack+0x20/0x24)
[  840.254947@3] [<c030e760>] (show_stack) from [<c035ed54>] (sched_show_task+0xc4/0x118)
[  840.262834@3] [<c035ed54>] (sched_show_task) from [<c0361cfc>] (dump_cpu_task+0x48/0x4c)
[  840.270896@3] [<c0361cfc>] (dump_cpu_task) from [<c0c8d478>] (pr_lockup_info+0x1ac/0x3b8)
[  840.279042@3] [<c0c8d478>] (pr_lockup_info) from [<c03efab8>] (watchdog_check_hardlockup_other_cpu+0xe0/0x130)
[  840.289008@3] [<c03efab8>] (watchdog_check_hardlockup_other_cpu) from [<c03eecb0>] (watchdog_timer_fn+0xd0/0x37c)
[  840.299235@3] [<c03eecb0>] (watchdog_timer_fn) from [<c03b02b8>] (__hrtimer_run_queues+0x174/0x3e0)
[  840.308248@3] [<c03b02b8>] (__hrtimer_run_queues) from [<c03b078c>] (hrtimer_interrupt+0xdc/0x238)
[  840.317175@3] [<c03b078c>] (hrtimer_interrupt) from [<c0a1efe8>] (arch_timer_handler_virt+0x40/0x48)
[  840.326278@3] [<c0a1efe8>] (arch_timer_handler_virt) from [<c039bbe4>] (handle_percpu_devid_irq+0xd4/0x324)
[  840.335981@3] [<c039bbe4>] (handle_percpu_devid_irq) from [<c0396120>] (generic_handle_irq+0x34/0x44)
[  840.345167@3] [<c0396120>] (generic_handle_irq) from [<c0396720>] (__handle_domain_irq+0x8c/0xfc)
[  840.354006@3] [<c0396720>] (__handle_domain_irq) from [<c0301614>] (gic_handle_irq+0x48/0x84)
[  840.362499@3] [<c0301614>] (gic_handle_irq) from [<c030f40c>] (__irq_svc+0x6c/0xa8)
[  840.370124@3] Exception stack(0xe7d5dbb0 to 0xe7d5dbf8)
[  840.375326@3] dba0:                                     c18273ec 00000004 fac81000 c0312d6c
[  840.383646@3] dbc0: c18e3d80 00005dbf b7620e11 000040d8 c1425214 0000003e 1ffffc70 e7d5dc0c
[  840.391965@3] dbe0: e7d5dc10 e7d5dc00 c06ec5b4 c0312d80 a0030013 ffffffff
[  840.398727@3] [<c030f40c>] (__irq_svc) from [<c0312d80>] (arch_timer_read_counter_long+0x14/0x28)
[  840.407568@3] [<c0312d80>] (arch_timer_read_counter_long) from [<c06ec5b4>] (__timer_delay+0x58/0x68)
[  840.416754@3] [<c06ec5b4>] (__timer_delay) from [<c06ec5f4>] (__timer_const_udelay+0x30/0x34)
[  840.425249@3] [<c06ec5f4>] (__timer_const_udelay) from [<c043dec4>] (panic+0x280/0x290)
[  840.433221@3] [<c043dec4>] (panic) from [<c030ea68>] (die+0x304/0x314)
[  840.439721@3] [<c030ea68>] (die) from [<c031ed40>] (__do_kernel_fault.part.0+0x74/0x1f4)
[  840.447782@3] [<c031ed40>] (__do_kernel_fault.part.0) from [<c031ada4>] (do_page_fault+0x388/0x394)
[  840.456794@3] [<c031ada4>] (do_page_fault) from [<c031af28>] (do_translation_fault+0xbc/0xc0)
[  840.465286@3] [<c031af28>] (do_translation_fault) from [<c0301354>] (do_DataAbort+0x5c/0xf0)
[  840.473693@3] [<c0301354>] (do_DataAbort) from [<c030f378>] (__dabt_svc+0x58/0x80)
[  840.481230@3] Exception stack(0xe7d5ddf8 to 0xe7d5de40)
[  840.486431@3] dde0:                                                       00000063 c13076b8
[  840.494753@3] de00: 00000001 00000000 c171d870 00000000 00000063 00000000 c17524e0 b311d284
[  840.503073@3] de20: 00000002 e7d5de54 e7d5de48 e7d5de48 c0784c80 c0784c98 60010013 ffffffff
[  840.511395@3] [<c030f378>] (__dabt_svc) from [<c0784c98>] (sysrq_handle_crash+0x30/0x38)
[  840.519455@3] [<c0784c98>] (sysrq_handle_crash) from [<c07852f0>] (__handle_sysrq+0x134/0x188)
[  840.528034@3] [<c07852f0>] (__handle_sysrq) from [<c07857e4>] (write_sysrq_trigger+0x80/0x90)
[  840.536529@3] [<c07857e4>] (write_sysrq_trigger) from [<c0512724>] (proc_reg_write+0x6c/0x90)
[  840.545022@3] [<c0512724>] (proc_reg_write) from [<c049f2b0>] (__vfs_write+0x48/0x140)
[  840.552908@3] [<c049f2b0>] (__vfs_write) from [<c04a023c>] (vfs_write+0xb4/0x1c0)
[  840.560360@3] [<c04a023c>] (vfs_write) from [<c04a1448>] (SyS_write+0x5c/0xbc)
[  840.567554@3] [<c04a1448>] (SyS_write) from [<c0308980>] (ret_fast_syscall+0x0/0x48)
[  840.575264@3] 
[  840.575264@3] HARDLOCKUP____ERR.END
[  840.575264@3] 
[  840.582024@3] ------------[ cut here ]------------
[  840.586793@3] WARNING: CPU: 3 PID: 8944 at /ssd/jason/eagle/common/kernel/watchdog_hld.c:212 watchdog_check_hardlockup_other_cpu+0x110/0x130
[  840.599356@3] Watchdog detected hard LOCKUP on cpu 0[  840.604124@3] Modules linked in:
 btusb(O) snd_soc_tlv320adc3101 gpio_keypad(O) remote(O) hid_skbrm(O) aml_hardware_dmx(O) atbm8881_fe(O) mali_kbase(O) vpu(O) encoder(O) amvdec_avs2(O) amvdec_vp9(O) amvdec_vc1(O) amvdec_real(O) amvdec_mmpeg4(O) amvdec_mpeg4(O) amvdec_mmpeg12(O) amvdec_mpeg12(O) amvdec_mmjpeg(O) amvdec_mjpeg(O) amvdec_h265(O) amvdec_h264mvc(O) amvdec_mh264(O) amvdec_h264(O) amvdec_avs(O) stream_input(O) decoder_common(O) firmware(O) media_clock(O) optee_armtz(O) optee(O)
[  840.647199@3] CPU: 3 PID: 8944 Comm: sh Tainted: G      D    O    4.9.113 #1
[  840.654216@3] Hardware name: Generic DT based system
[  840.659160@3] [<c0313cf8>] (unwind_backtrace) from [<c030e760>] (show_stack+0x20/0x24)
[  840.667046@3] [<c030e760>] (show_stack) from [<c06ee888>] (dump_stack+0x90/0xac)
[  840.674415@3] [<c06ee888>] (dump_stack) from [<c0328f44>] (__warn+0xf8/0x110)
[  840.681520@3] [<c0328f44>] (__warn) from [<c0328fb0>] (warn_slowpath_fmt+0x54/0x70)
[  840.689148@3] [<c0328fb0>] (warn_slowpath_fmt) from [<c03efae8>] (watchdog_check_hardlockup_other_cpu+0x110/0x130)
[  840.699460@3] [<c03efae8>] (watchdog_check_hardlockup_other_cpu) from [<c03eecb0>] (watchdog_timer_fn+0xd0/0x37c)
[  840.709687@3] [<c03eecb0>] (watchdog_timer_fn) from [<c03b02b8>] (__hrtimer_run_queues+0x174/0x3e0)
[  840.718700@3] [<c03b02b8>] (__hrtimer_run_queues) from [<c03b078c>] (hrtimer_interrupt+0xdc/0x238)
[  840.727627@3] [<c03b078c>] (hrtimer_interrupt) from [<c0a1efe8>] (arch_timer_handler_virt+0x40/0x48)
[  840.736728@3] [<c0a1efe8>] (arch_timer_handler_virt) from [<c039bbe4>] (handle_percpu_devid_irq+0xd4/0x324)
[  840.746434@3] [<c039bbe4>] (handle_percpu_devid_irq) from [<c0396120>] (generic_handle_irq+0x34/0x44)
[  840.755620@3] [<c0396120>] (generic_handle_irq) from [<c0396720>] (__handle_domain_irq+0x8c/0xfc)
[  840.764459@3] [<c0396720>] (__handle_domain_irq) from [<c0301614>] (gic_handle_irq+0x48/0x84)
[  840.772952@3] [<c0301614>] (gic_handle_irq) from [<c030f40c>] (__irq_svc+0x6c/0xa8)
[  840.780577@3] Exception stack(0xe7d5dbb0 to 0xe7d5dbf8)
[  840.785779@3] dba0:                                     c18273ec 00000004 fac81000 c0312d6c
[  840.794099@3] dbc0: c18e3d80 00005dbf b7620e11 000040d8 c1425214 0000003e 1ffffc70 e7d5dc0c
[  840.802419@3] dbe0: e7d5dc10 e7d5dc00 c06ec5b4 c0312d80 a0030013 ffffffff
[  840.809180@3] [<c030f40c>] (__irq_svc) from [<c0312d80>] (arch_timer_read_counter_long+0x14/0x28)
[  840.818021@3] [<c0312d80>] (arch_timer_read_counter_long) from [<c06ec5b4>] (__timer_delay+0x58/0x68)
[  840.827206@3] [<c06ec5b4>] (__timer_delay) from [<c06ec5f4>] (__timer_const_udelay+0x30/0x34)
[  840.835700@3] [<c06ec5f4>] (__timer_const_udelay) from [<c043dec4>] (panic+0x280/0x290)
[  840.843673@3] [<c043dec4>] (panic) from [<c030ea68>] (die+0x304/0x314)
[  840.850173@3] [<c030ea68>] (die) from [<c031ed40>] (__do_kernel_fault.part.0+0x74/0x1f4)
[  840.858234@3] [<c031ed40>] (__do_kernel_fault.part.0) from [<c031ada4>] (do_page_fault+0x388/0x394)
[  840.867246@3] [<c031ada4>] (do_page_fault) from [<c031af28>] (do_translation_fault+0xbc/0xc0)
[  840.875739@3] [<c031af28>] (do_translation_fault) from [<c0301354>] (do_DataAbort+0x5c/0xf0)
[  840.884146@3] [<c0301354>] (do_DataAbort) from [<c030f378>] (__dabt_svc+0x58/0x80)
[  840.891684@3] Exception stack(0xe7d5ddf8 to 0xe7d5de40)
[  840.896884@3] dde0:                                                       00000063 c13076b8
[  840.905206@3] de00: 00000001 00000000 c171d870 00000000 00000063 00000000 c17524e0 b311d284
[  840.913526@3] de20: 00000002 e7d5de54 e7d5de48 e7d5de48 c0784c80 c0784c98 60010013 ffffffff
[  840.921847@3] [<c030f378>] (__dabt_svc) from [<c0784c98>] (sysrq_handle_crash+0x30/0x38)
[  840.929907@3] [<c0784c98>] (sysrq_handle_crash) from [<c07852f0>] (__handle_sysrq+0x134/0x188)
[  840.938488@3] [<c07852f0>] (__handle_sysrq) from [<c07857e4>] (write_sysrq_trigger+0x80/0x90)
[  840.946981@3] [<c07857e4>] (write_sysrq_trigger) from [<c0512724>] (proc_reg_write+0x6c/0x90)
[  840.955473@3] [<c0512724>] (proc_reg_write) from [<c049f2b0>] (__vfs_write+0x48/0x140)
[  840.963359@3] [<c049f2b0>] (__vfs_write) from [<c04a023c>] (vfs_write+0xb4/0x1c0)
[  840.970813@3] [<c04a023c>] (vfs_write) from [<c04a1448>] (SyS_write+0x5c/0xbc)
[  840.978006@3] [<c04a1448>] (SyS_write) from [<c0308980>] (ret_fast_syscall+0x0/0x48)
[  840.985717@3] ---[ end trace c250ae4b2a0bb56e ]---
console-ramoops-0 (32,756 bytes)   

(SW) Kerwin Chen

2019-03-07 18:49

developer   ~0001404

Hi Eric,

The ramoops file you attached is correct.
You can see that system crash due to 'sysrq' command. ( console-ramoops-0, around line 212 --> "sysrq: SysRq : Trigger a crash" )

(ALTech) Eric Kim

2019-03-08 11:16

developer   ~0001415

Hi Kerwin,

Thanks for confirming it.

(SW) Kerwin Chen

2019-03-12 10:23

developer   ~0001455

fixed in r5b release

Issue History

Date Modified Username Field Change
2019-03-06 19:22 (ALTech) SY Yoon New Issue
2019-03-06 19:22 (ALTech) SY Yoon Status new => assigned
2019-03-06 19:22 (ALTech) SY Yoon Assigned To => (ALTech) SY Yoon
2019-03-06 19:22 (ALTech) SY Yoon Assigned To (ALTech) SY Yoon => (SW) Jacky Chiang
2019-03-06 19:22 (ALTech) SY Yoon Issue Monitored: (ALTech) Eric Kim
2019-03-06 19:22 (ALTech) SY Yoon Issue Monitored: (ALTech) Jewoo Lee
2019-03-06 19:34 (SW) Jacky Chiang Assigned To (SW) Jacky Chiang => (SW) Kerwin Chen
2019-03-06 19:35 (SW) Jacky Chiang Note Added: 0001372
2019-03-06 19:36 (SW) Jacky Chiang Note Edited: 0001372
2019-03-07 12:18 (SW) Kerwin Chen Note Added: 0001397
2019-03-07 12:22 (SW) Kerwin Chen Assigned To (SW) Kerwin Chen => (ALTech) SY Yoon
2019-03-07 12:22 (SW) Kerwin Chen Status assigned => resolved
2019-03-07 12:22 (SW) Kerwin Chen Resolution open => fixed
2019-03-07 12:22 (SW) Kerwin Chen Note Added: 0001398
2019-03-07 16:44 (ALTech) Eric Kim File Added: console-ramoops-0
2019-03-07 16:44 (ALTech) Eric Kim Note Added: 0001401
2019-03-07 18:49 (SW) Kerwin Chen Note Added: 0001404
2019-03-08 11:16 (ALTech) Eric Kim Note Added: 0001415
2019-03-12 10:23 (SW) Kerwin Chen Status resolved => closed
2019-03-12 10:23 (SW) Kerwin Chen Note Added: 0001455